﻿2026-06-22T01:09:44.5158602Z ##[group]Run cargo nextest run -p spt-daemon --no-fail-fast \
2026-06-22T01:09:44.5159298Z [36;1mcargo nextest run -p spt-daemon --no-fail-fast \[0m
2026-06-22T01:09:44.5159798Z [36;1m  -E 'test(a_journaled_input_wedge) | test(p0_paste_wedge) | test(g2_no_commit)' \[0m
2026-06-22T01:09:44.5160258Z [36;1m  --no-capture 2>&1 \[0m
2026-06-22T01:09:44.5160930Z [36;1m| grep -E 'JOURNAL-WEDGE GATE|PASTE-WEDGE GATE|subscribed|got_output|attach_received|keystroke_accepted|assertion|panicked|c1:|c2:|c3:|EVICT|c3-DROP|PASS|FAIL' \[0m
2026-06-22T01:09:44.5161632Z [36;1m| grep -vE 'drain appends=' || true[0m
2026-06-22T01:09:44.9416727Z shell: /usr/bin/bash -e {0}
2026-06-22T01:09:44.9416998Z env:
2026-06-22T01:09:44.9417199Z   SPT_REGISTRY_BUSY_TIMEOUT_MS: 30000
2026-06-22T01:09:44.9417448Z   RUSTFLAGS: -C link-arg=-fuse-ld=mold
2026-06-22T01:09:44.9417676Z   SPT_WEDGE_TRACE: 1
2026-06-22T01:09:44.9417871Z ##[endgroup]
2026-06-22T01:10:10.8570404Z WEDGE_TRACE c3-DROP: stream=1 append seq=0 but NO subscriber yet
2026-06-22T01:10:10.8571762Z WEDGE_TRACE c1: serve recv Output seq=82702 count=1
2026-06-22T01:10:10.8574658Z WEDGE_TRACE c2: serve fwd Output seq=82702 count=1
2026-06-22T01:10:10.8575078Z WEDGE_TRACE c3: stream=2 live-send seq=0 count=1 write_ok=true
2026-06-22T01:10:10.8575594Z WEDGE_TRACE c1-EVICT: dropped 1 viewer(s) at seq 87054; total_evicted=1 (drain_appends=87055)
2026-06-22T01:10:10.8576089Z WEDGE_TRACE c1: serve recv Output seq=82957 count=256
2026-06-22T01:10:10.8576452Z WEDGE_TRACE c2: serve fwd Output seq=82957 count=256
2026-06-22T01:10:10.8576834Z WEDGE_TRACE c3: stream=2 live-send seq=255 count=256 write_ok=true
2026-06-22T01:10:10.8577210Z WEDGE_TRACE c1: serve recv Output seq=83213 count=512
2026-06-22T01:10:10.8577562Z WEDGE_TRACE c2: serve fwd Output seq=83213 count=512
2026-06-22T01:10:10.8577929Z WEDGE_TRACE c3: stream=2 live-send seq=511 count=512 write_ok=true
2026-06-22T01:10:10.8578301Z WEDGE_TRACE c1: serve recv Output seq=83469 count=768
2026-06-22T01:10:10.8578639Z WEDGE_TRACE c2: serve fwd Output seq=83469 count=768
2026-06-22T01:10:10.8579135Z WEDGE_TRACE c3: stream=2 live-send seq=767 count=768 write_ok=true
2026-06-22T01:10:10.8579540Z WEDGE_TRACE c1: serve recv Output seq=83725 count=1024
2026-06-22T01:10:10.8579859Z WEDGE_TRACE c2: serve fwd Output seq=83725 count=1024
2026-06-22T01:10:10.8580186Z WEDGE_TRACE c3: stream=2 live-send seq=1023 count=1024 write_ok=true
2026-06-22T01:10:10.8580519Z WEDGE_TRACE c1: serve recv Output seq=83981 count=1280
2026-06-22T01:10:10.8580815Z WEDGE_TRACE c2: serve fwd Output seq=83981 count=1280
2026-06-22T01:10:10.8581139Z WEDGE_TRACE c3: stream=2 live-send seq=1279 count=1280 write_ok=true
2026-06-22T01:10:10.8581473Z WEDGE_TRACE c1: serve recv Output seq=84237 count=1536
2026-06-22T01:10:10.8581764Z WEDGE_TRACE c2: serve fwd Output seq=84237 count=1536
2026-06-22T01:10:10.8582093Z WEDGE_TRACE c3: stream=2 live-send seq=1535 count=1536 write_ok=true
2026-06-22T01:10:10.8582422Z WEDGE_TRACE c1: serve recv Output seq=84493 count=1792
2026-06-22T01:10:10.8582727Z WEDGE_TRACE c2: serve fwd Output seq=84493 count=1792
2026-06-22T01:10:10.8583039Z WEDGE_TRACE c3: stream=2 live-send seq=1791 count=1792 write_ok=true
2026-06-22T01:10:10.8583377Z WEDGE_TRACE c1: serve recv Output seq=84749 count=2048
2026-06-22T01:10:10.8583678Z WEDGE_TRACE c2: serve fwd Output seq=84749 count=2048
2026-06-22T01:10:10.8584302Z WEDGE_TRACE c3: stream=2 live-send seq=2047 count=2048 write_ok=true
2026-06-22T01:10:10.8584630Z WEDGE_TRACE c1: serve recv Output seq=85005 count=2304
2026-06-22T01:10:10.8584927Z WEDGE_TRACE c2: serve fwd Output seq=85005 count=2304
2026-06-22T01:10:10.8585242Z WEDGE_TRACE c3: stream=2 live-send seq=2303 count=2304 write_ok=true
2026-06-22T01:10:10.8585577Z WEDGE_TRACE c1: serve recv Output seq=85261 count=2560
2026-06-22T01:10:10.8585859Z WEDGE_TRACE c2: serve fwd Output seq=85261 count=2560
2026-06-22T01:10:10.8586187Z WEDGE_TRACE c3: stream=2 live-send seq=2559 count=2560 write_ok=true
2026-06-22T01:10:10.8586521Z WEDGE_TRACE c1: serve recv Output seq=85517 count=2816
2026-06-22T01:10:10.8587012Z WEDGE_TRACE c2: serve fwd Output seq=85517 count=2816
2026-06-22T01:10:10.8587342Z WEDGE_TRACE c3: stream=2 live-send seq=2815 count=2816 write_ok=true
2026-06-22T01:10:10.8587680Z WEDGE_TRACE c1: serve recv Output seq=85773 count=3072
2026-06-22T01:10:10.8587986Z WEDGE_TRACE c2: serve fwd Output seq=85773 count=3072
2026-06-22T01:10:10.8588308Z WEDGE_TRACE c3: stream=2 live-send seq=3071 count=3072 write_ok=true
2026-06-22T01:10:10.8588637Z WEDGE_TRACE c1: serve recv Output seq=86029 count=3328
2026-06-22T01:10:10.8588933Z WEDGE_TRACE c2: serve fwd Output seq=86029 count=3328
2026-06-22T01:10:10.8589362Z WEDGE_TRACE c3: stream=2 live-send seq=3327 count=3328 write_ok=true
2026-06-22T01:10:10.8589686Z WEDGE_TRACE c1: serve recv Output seq=86285 count=3584
2026-06-22T01:10:10.8589987Z WEDGE_TRACE c2: serve fwd Output seq=86285 count=3584
2026-06-22T01:10:10.8590284Z WEDGE_TRACE c3: stream=2 live-send seq=3583 count=3584 write_ok=true
2026-06-22T01:10:10.8590596Z WEDGE_TRACE c1: serve recv Output seq=86541 count=3840
2026-06-22T01:10:10.8590860Z WEDGE_TRACE c2: serve fwd Output seq=86541 count=3840
2026-06-22T01:10:10.8591151Z WEDGE_TRACE c3: stream=2 live-send seq=3839 count=3840 write_ok=true
2026-06-22T01:10:10.8591451Z WEDGE_TRACE c1: serve recv Output seq=86797 count=4096
2026-06-22T01:10:10.8591718Z WEDGE_TRACE c2: serve fwd Output seq=86797 count=4096
2026-06-22T01:10:10.8692552Z === W1b JOURNAL-WEDGE GATE: journaled_ops_pumped=2450 concurrent_attach_subscribed=true attach_received_pty_output=true (fixed = both true; pre-fix on Unix: subscribed=false — attach wedged on the held journal lock) ===
2026-06-22T01:10:10.8698288Z         PASS [   3.450s] (1/3) spt-daemon::inject_control_wedge a_journaled_input_wedge_does_not_starve_a_concurrent_rc_attach
2026-06-22T01:10:10.8699401Z thread 'g2_no_commit_deadline_faults_binary_and_does_not_wedge_controller_input' (3901811) panicked at crates/spt-daemon/tests/inject_control_wedge.rs:1226:5:
2026-06-22T01:10:10.8739480Z test g2_no_commit_deadline_faults_binary_and_does_not_wedge_controller_input ... FAILED
2026-06-22T01:10:10.8740330Z test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 9 filtered out; finished in 16.71s
2026-06-22T01:10:10.8740964Z         FAIL [  16.714s] (2/3) spt-daemon::inject_control_wedge g2_no_commit_deadline_faults_binary_and_does_not_wedge_controller_input
2026-06-22T01:10:10.8741479Z WEDGE_TRACE c3-DROP: stream=1 append seq=0 but NO subscriber yet
2026-06-22T01:10:10.8741798Z WEDGE_TRACE c1: serve recv Output seq=59296 count=1
2026-06-22T01:10:10.8742108Z WEDGE_TRACE c3: stream=2 live-send seq=0 count=1 write_ok=true
2026-06-22T01:10:10.8742404Z WEDGE_TRACE c2: serve fwd Output seq=59296 count=1
2026-06-22T01:10:10.8742776Z WEDGE_TRACE c1-EVICT: dropped 1 viewer(s) at seq 63648; total_evicted=1 (drain_appends=63649)
2026-06-22T01:10:10.8743153Z WEDGE_TRACE c1: serve recv Output seq=59551 count=256
2026-06-22T01:10:10.8743434Z WEDGE_TRACE c2: serve fwd Output seq=59551 count=256
2026-06-22T01:10:10.8743752Z WEDGE_TRACE c3: stream=2 live-send seq=255 count=256 write_ok=true
2026-06-22T01:10:10.8744088Z WEDGE_TRACE c1: serve recv Output seq=59807 count=512
2026-06-22T01:10:10.8744356Z WEDGE_TRACE c2: serve fwd Output seq=59807 count=512
2026-06-22T01:10:10.8744651Z WEDGE_TRACE c3: stream=2 live-send seq=511 count=512 write_ok=true
2026-06-22T01:10:10.8745157Z WEDGE_TRACE c1: serve recv Output seq=60063 count=768
2026-06-22T01:10:10.8745424Z WEDGE_TRACE c2: serve fwd Output seq=60063 count=768
2026-06-22T01:10:10.8745715Z WEDGE_TRACE c3: stream=2 live-send seq=767 count=768 write_ok=true
2026-06-22T01:10:10.8746015Z WEDGE_TRACE c1: serve recv Output seq=60319 count=1024
2026-06-22T01:10:10.8746287Z WEDGE_TRACE c2: serve fwd Output seq=60319 count=1024
2026-06-22T01:10:10.8746591Z WEDGE_TRACE c3: stream=2 live-send seq=1023 count=1024 write_ok=true
2026-06-22T01:10:10.8746910Z WEDGE_TRACE c1: serve recv Output seq=60575 count=1280
2026-06-22T01:10:10.8747178Z WEDGE_TRACE c2: serve fwd Output seq=60575 count=1280
2026-06-22T01:10:10.8747601Z WEDGE_TRACE c3: stream=2 live-send seq=1279 count=1280 write_ok=true
2026-06-22T01:10:10.8747916Z WEDGE_TRACE c1: serve recv Output seq=60831 count=1536
2026-06-22T01:10:10.8748184Z WEDGE_TRACE c2: serve fwd Output seq=60831 count=1536
2026-06-22T01:10:10.8748522Z WEDGE_TRACE c3: stream=2 live-send seq=1535 count=1536 write_ok=true
2026-06-22T01:10:10.8748831Z WEDGE_TRACE c1: serve recv Output seq=61087 count=1792
2026-06-22T01:10:10.8749191Z WEDGE_TRACE c2: serve fwd Output seq=61087 count=1792
2026-06-22T01:10:10.8749501Z WEDGE_TRACE c3: stream=2 live-send seq=1791 count=1792 write_ok=true
2026-06-22T01:10:10.8749806Z WEDGE_TRACE c1: serve recv Output seq=61343 count=2048
2026-06-22T01:10:10.8750078Z WEDGE_TRACE c2: serve fwd Output seq=61343 count=2048
2026-06-22T01:10:10.8750374Z WEDGE_TRACE c3: stream=2 live-send seq=2047 count=2048 write_ok=true
2026-06-22T01:10:10.8750674Z WEDGE_TRACE c1: serve recv Output seq=61599 count=2304
2026-06-22T01:10:10.8750966Z WEDGE_TRACE c2: serve fwd Output seq=61599 count=2304
2026-06-22T01:10:10.8751276Z WEDGE_TRACE c3: stream=2 live-send seq=2303 count=2304 write_ok=true
2026-06-22T01:10:10.8751586Z WEDGE_TRACE c1: serve recv Output seq=61855 count=2560
2026-06-22T01:10:10.8751860Z WEDGE_TRACE c2: serve fwd Output seq=61855 count=2560
2026-06-22T01:10:10.8752163Z WEDGE_TRACE c3: stream=2 live-send seq=2559 count=2560 write_ok=true
2026-06-22T01:10:10.8752469Z WEDGE_TRACE c1: serve recv Output seq=62111 count=2816
2026-06-22T01:10:10.8752740Z WEDGE_TRACE c2: serve fwd Output seq=62111 count=2816
2026-06-22T01:10:10.8753035Z WEDGE_TRACE c3: stream=2 live-send seq=2815 count=2816 write_ok=true
2026-06-22T01:10:10.8753350Z WEDGE_TRACE c1: serve recv Output seq=62367 count=3072
2026-06-22T01:10:10.8753627Z WEDGE_TRACE c2: serve fwd Output seq=62367 count=3072
2026-06-22T01:10:10.8753927Z WEDGE_TRACE c3: stream=2 live-send seq=3071 count=3072 write_ok=true
2026-06-22T01:10:10.8754237Z WEDGE_TRACE c1: serve recv Output seq=62623 count=3328
2026-06-22T01:10:10.8754530Z WEDGE_TRACE c2: serve fwd Output seq=62623 count=3328
2026-06-22T01:10:10.8754845Z WEDGE_TRACE c3: stream=2 live-send seq=3327 count=3328 write_ok=true
2026-06-22T01:10:10.8755160Z WEDGE_TRACE c1: serve recv Output seq=62879 count=3584
2026-06-22T01:10:10.8755441Z WEDGE_TRACE c2: serve fwd Output seq=62879 count=3584
2026-06-22T01:10:10.8755732Z WEDGE_TRACE c3: stream=2 live-send seq=3583 count=3584 write_ok=true
2026-06-22T01:10:10.8756033Z WEDGE_TRACE c1: serve recv Output seq=63135 count=3840
2026-06-22T01:10:10.8756304Z WEDGE_TRACE c2: serve fwd Output seq=63135 count=3840
2026-06-22T01:10:10.8756605Z WEDGE_TRACE c3: stream=2 live-send seq=3839 count=3840 write_ok=true
2026-06-22T01:10:10.8756915Z WEDGE_TRACE c1: serve recv Output seq=63391 count=4096
2026-06-22T01:10:10.8757179Z WEDGE_TRACE c2: serve fwd Output seq=63391 count=4096
2026-06-22T01:10:10.8758663Z === P0 PASTE-WEDGE GATE: pumped=8855 keystroke_accepted=true concurrent_attach_subscribed=true attach_received_output=true backpressured=true backpressure_after_stop=Some(true) (fixed = subscribed+got_output+keystroke_accepted true; pre-fix: parked dispatch thread → subscribed=false / no output) ===
2026-06-22T01:10:10.8759910Z         PASS [   4.036s] (3/3) spt-daemon::inject_control_wedge p0_paste_wedge_parked_write_does_not_starve_attach_or_wedge_broker
2026-06-22T01:10:10.8760667Z         FAIL [  16.714s] (2/3) spt-daemon::inject_control_wedge g2_no_commit_deadline_faults_binary_and_does_not_wedge_controller_input
